time="2022-09-23T13:54:56Z" level=info msg="N3IWF Log level is set to [debug] level" category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="set log level : info" category=NGAP component=LIB
time="2022-09-23T13:54:56Z" level=info msg="set report call : false" category=NGAP component=LIB
time="2022-09-23T13:54:56Z" level=info msg="set log level : info" category=Aper component=LIB
time="2022-09-23T13:54:56Z" level=info msg="set report call : false" category=Aper component=LIB
time="2022-09-23T13:54:56Z" level=info msg="config version [1.0.3]" category=CFG component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg=n3iwf category=App component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="N3IWF version:  \n\tNot specify ldflags (which link version) during go build\n\tgo version: go1.14.4 linux/arm64" category=App component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="Server started" category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=warning msg="Parse PKCS8 private key failed: x509: failed to parse private key (use ParsePKCS1PrivateKey instead for this key format)" category=Context component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="Parse using PKCS1..." category=Context component=N3IWF
time="2022-09-23T13:54:56Z" level=debug msg="XFRM interface xfrmi-default index is 17" category=IKE component=N3IWF
time="2022-09-23T13:54:56Z" level=warning msg="netlink.RouteAdd: file exists" category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="Setup XFRM interface xfrmi-default " category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="[N3IWF] Send NG Setup Request" category=NGAP component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="NGAP service running." category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="NAS TCP server successfully started." category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="Listening NWu user plane traffic" category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="IKE service running." category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="N3IWF running..." category=Init component=N3IWF
time="2022-09-23T13:54:56Z" level=info msg="[N3IWF] Handle NG Setup Response" category=NGAP component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[SecurityAssociation] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[KeyExchange] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Nonce] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Handle IKE_SA_INIT" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="====== IKE Security Association Info =====" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Initiator's SPI: 000000000001e0f3" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Responder's  SPI: daf7d28be5d44b4d" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Encryption Algorithm: 12" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_ei: b106de054c81495563d3b2a75304f06cad97b849a3d969e639b30779540bef01" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_er: ed3f96ec1fc6e0ccbeadb2a1874eccda9da616e712afddb590e4772e00299f66" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Integrity Algorithm: 2" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_ai: 5e729f93245c76d552fe6b607e59b09caf49a113" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_ar: 0fd1b971ad01e853365d26a58136e5226bdf433e" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_pi: bfea4b1ba8a159af019dd253eabc2818be06590f" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="SK_pr: dd6ed4e3870244cb8d095a3e6696be64dd4b1111" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[SecurityAssociation] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[KeyExchange] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Nonce] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[SecurityAssociation] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[KeyExchange] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Nonce] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Notification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Notification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Identification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[SecurityAssociation] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[KeyExchange] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Nonce] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Notification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Notification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Handle IKE_AUTH" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Identification] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[SecurityAssociation] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[TrafficSelector] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[TrafficSelector] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Ecoding initiator for later IKE authentication" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Identification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Parsing security association" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Received traffic selector initiator from UE" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Received traffic selector initiator from UE" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Identification] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Certificate] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Authentication] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[EAP] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[EAP][Expanded] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Handle IKE_AUTH" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE message" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Encoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[Encrypted] marshal(): Start marshalling" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Decoding IKE payloads" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[EAP] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[EAP][Expanded] unmarshal(): Start unmarshalling received bytes" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="===== Unmarshal EAP5G Data (Ref: TS24.502 Fig. 9.3.2.2.2-1) =====" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Message-Id: 2" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="AN-parameters length: 28" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Parsing AN-parameters...: [ 1  6  2  248  57  202  254  0  4  1  3  2  3  2  248  57  3  10  4  1  1  2  3  4  1  17  34  51]" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="-> Parameter type: GUAMI" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Unmarshal GUAMI: 00 02 f8 39 ca fe 00" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tGUAMI: PLMNIdentity[{02 f8 39}], AMFRegionID[{{ca  8}}], AMFSetID[{{fe 00  a}}], AMFPointer[{{00  6}}]" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="-> Parameter type: ANParametersTypeEstablishmentCause" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Unmarshal ANParametersTypeEstablishmentCause: 03" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="-> Parameter type: ANParametersTypeSelectedPLMNID" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Unmarshal SelectedPLMNID: 00 02 f8 39" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tSelectedPLMNID: 02 f8 39" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="-> Parameter type: ANParametersTypeRequestedNSSAI" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Unmarshal SNSSAI: 04 01 01 02 03" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSST: 01" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSD: 01 02 03" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Unmarshal SNSSAI: 04 01 11 22 33" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSST: 01" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSD: 11 22 33" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="nasPDULength: 22" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="nasPDUField: [ 126  0  65  121  0  12  1  2  248  57  240  255  0  0  0  0  71  120  46  2  128  32]" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="Select AMF with the following AN parameters:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tGUAMI: PLMNIdentity[{02 f8 39}], AMFRegionID[{{ca  8}}], AMFSetID[{{fe 00  a}}], AMFPointer[{{00  6}}]" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tSelectedPLMNID: [ 2  248  57]" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tRequestedNSSAI:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tRequestedNSSAI:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\tSNSSAI 1:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSST: 01" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSD: 01 02 03" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\tRequestedNSSAI:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\tSNSSAI 2:" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSST: 01" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=debug msg="\t\t\tSD: 11 22 33" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="Selected AMF Name: AMF" category=IKE component=N3IWF
time="2022-09-23T13:55:02Z" level=info msg="[N3IWF] Send Initial UE Message" category=NGAP component=N3IWF
time="2022-09-23T13:55:19Z" level=fatal msg="panic: runtime error: invalid memory address or nil pointer dereference\ngoroutine 22 [running]:\nruntime/debug.Stack(0x40003e5bc8, 0x6e3600, 0xe2de50)\n\t/usr/local/go/src/runtime/debug/stack.go:24 +0x88\ngithub.com/free5gc/n3iwf/internal/ngap/service.listenAndServe.func1()\n\t/home/tngf/free5-gc/NFs/n3iwf/internal/ngap/service/service.go:50 +0x4c\npanic(0x6e3600, 0xe2de50)\n\t/usr/local/go/src/runtime/panic.go:969 +0x11c\ngithub.com/free5gc/n3iwf/internal/ngap/service.listenAndServe(0x40002f91e0, 0x400051be60, 0x400008c420)\n\t/home/tngf/free5-gc/NFs/n3iwf/internal/ngap/service/service.go:121 +0x5d8\ncreated by github.com/free5gc/n3iwf/internal/ngap/service.Run\n\t/home/tngf/free5-gc/NFs/n3iwf/internal/ngap/service/service.go:36 +0xcc\n" category=NGAP component=N3IWF
